Dec 01 11:02:08.511 INFO testing loggerhead/shadowsocks-rust against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 01 11:02:08.511 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-3/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 11:02:08.911 INFO [stdout] d617ae50d37f5798525afde2c977a0423a27c7764face5d18455978b0232bdec
Dec 01 11:02:08.913 INFO running `"docker" "start" "-a" "d617ae50d37f5798525afde2c977a0423a27c7764face5d18455978b0232bdec"`
Dec 01 11:02:10.454 INFO [stderr] usermod: no changes
Dec 01 11:02:10.499 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Dec 01 11:02:10.691 INFO [stderr]    Compiling slog v1.7.1
Dec 01 11:02:10.699 INFO [stderr]    Compiling lru_time_cache v0.5.0
Dec 01 11:02:10.699 INFO [stderr]    Compiling try_opt v0.1.1
Dec 01 11:02:10.699 INFO [stderr]    Compiling net2 v0.2.31
Dec 01 11:02:10.699 INFO [stderr]    Compiling term_size v0.2.3
Dec 01 11:02:10.699 INFO [stderr]    Compiling isatty v0.1.6
Dec 01 11:02:10.699 INFO [stderr]    Compiling sig v1.0.0
Dec 01 11:02:10.699 INFO [stderr]    Compiling daemonize v0.2.3
Dec 01 11:02:11.891 INFO [stderr]    Compiling nix v0.5.1
Dec 01 11:02:11.975 INFO [stderr]    Compiling toml v0.1.30
Dec 01 11:02:12.347 INFO [stderr]    Compiling clap v2.18.0
Dec 01 11:02:12.956 INFO [stderr]    Compiling chrono v0.2.25
Dec 01 11:02:15.983 INFO [stderr]    Compiling slog-extra v0.1.2
Dec 01 11:02:15.983 INFO [stderr]    Compiling slog-scope v0.2.2
Dec 01 11:02:17.795 INFO [stderr]    Compiling miow v0.1.5
Dec 01 11:02:23.547 INFO [stderr]    Compiling slog-stream v1.2.1
Dec 01 11:02:26.871 INFO [stderr]    Compiling slog-term v1.3.5
Dec 01 11:02:34.539 INFO [stderr]    Compiling mio v0.5.1
Dec 01 11:02:47.587 INFO [stderr]    Compiling shadowsocks v0.6.2 (/source)
Dec 01 11:02:49.151 INFO [stderr] warning: unused macro definition
Dec 01 11:02:49.151 INFO [stderr]   --> src/util.rs:9:1
Dec 01 11:02:49.151 INFO [stderr]    |
Dec 01 11:02:49.151 INFO [stderr] 9  | / macro_rules! io_err {
Dec 01 11:02:49.151 INFO [stderr] 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Dec 01 11:02:49.151 INFO [stderr] 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Dec 01 11:02:49.151 INFO [stderr] 12 | | }
Dec 01 11:02:49.151 INFO [stderr]    | |_^
Dec 01 11:02:49.151 INFO [stderr]    |
Dec 01 11:02:49.151 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 11:02:49.151 INFO [stderr] 
Dec 01 11:02:49.244 INFO [stderr] warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
Dec 01 11:02:49.244 INFO [stderr]    --> src/config/running_config.rs:102:9
Dec 01 11:02:49.244 INFO [stderr]     |
Dec 01 11:02:49.244 INFO [stderr] 102 |         env::home_dir()
Dec 01 11:02:49.244 INFO [stderr]     |         ^^^^^^^^^^^^^
Dec 01 11:02:49.244 INFO [stderr]     |
Dec 01 11:02:49.244 INFO [stderr]     = note: #[warn(deprecated)] on by default
Dec 01 11:02:49.244 INFO [stderr] 
Dec 01 11:03:03.613 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 53.13s
Dec 01 11:03:03.635 INFO [stderr] su: No module specific data is present
Dec 01 11:03:04.827 INFO running `"docker" "inspect" "d617ae50d37f5798525afde2c977a0423a27c7764face5d18455978b0232bdec"`
Dec 01 11:03:05.093 INFO running `"docker" "rm" "-f" "d617ae50d37f5798525afde2c977a0423a27c7764face5d18455978b0232bdec"`
Dec 01 11:03:05.447 INFO [stdout] d617ae50d37f5798525afde2c977a0423a27c7764face5d18455978b0232bdec
Dec 01 11:03:05.455 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-3/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 11:03:05.895 INFO [stdout] 6dcc8d4eef54243dcae32f26b1412e9c6bb4bcc940282d0f8fa2861277530865
Dec 01 11:03:05.903 INFO running `"docker" "start" "-a" "6dcc8d4eef54243dcae32f26b1412e9c6bb4bcc940282d0f8fa2861277530865"`
Dec 01 11:03:07.906 INFO [stderr] usermod: no changes
Dec 01 11:03:07.955 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Dec 01 11:03:08.133 INFO [stderr]    Compiling shadowsocks v0.6.2 (/source)
Dec 01 11:03:10.983 INFO [stderr] warning: unused macro definition
Dec 01 11:03:10.983 INFO [stderr]   --> tests/encrypt.rs:39:1
Dec 01 11:03:10.983 INFO [stderr]    |
Dec 01 11:03:10.983 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt {
Dec 01 11:03:10.983 INFO [stderr] 40 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) )
Dec 01 11:03:10.983 INFO [stderr] 41 | | }
Dec 01 11:03:10.983 INFO [stderr]    | |_^
Dec 01 11:03:10.987 INFO [stderr]    |
Dec 01 11:03:10.987 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 11:03:10.987 INFO [stderr] 
Dec 01 11:03:10.987 INFO [stderr] warning: unused macro definition
Dec 01 11:03:10.987 INFO [stderr]   --> tests/encrypt.rs:43:1
Dec 01 11:03:10.987 INFO [stderr]    |
Dec 01 11:03:10.987 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt {
Dec 01 11:03:10.987 INFO [stderr] 44 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) )
Dec 01 11:03:10.987 INFO [stderr] 45 | | }
Dec 01 11:03:10.987 INFO [stderr]    | |_^
Dec 01 11:03:10.987 INFO [stderr] 
Dec 01 11:03:11.844 INFO [stderr] warning: unused macro definition
Dec 01 11:03:11.844 INFO [stderr]   --> src/util.rs:9:1
Dec 01 11:03:11.844 INFO [stderr]    |
Dec 01 11:03:11.844 INFO [stderr] 9  | / macro_rules! io_err {
Dec 01 11:03:11.844 INFO [stderr] 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Dec 01 11:03:11.844 INFO [stderr] 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Dec 01 11:03:11.844 INFO [stderr] 12 | | }
Dec 01 11:03:11.844 INFO [stderr]    | |_^
Dec 01 11:03:11.844 INFO [stderr]    |
Dec 01 11:03:11.844 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 11:03:11.844 INFO [stderr] 
Dec 01 11:03:11.951 INFO [stderr] warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
Dec 01 11:03:11.951 INFO [stderr]    --> src/config/running_config.rs:102:9
Dec 01 11:03:11.951 INFO [stderr]     |
Dec 01 11:03:11.951 INFO [stderr] 102 |         env::home_dir()
Dec 01 11:03:11.951 INFO [stderr]     |         ^^^^^^^^^^^^^
Dec 01 11:03:11.951 INFO [stderr]     |
Dec 01 11:03:11.951 INFO [stderr]     = note: #[warn(deprecated)] on by default
Dec 01 11:03:11.951 INFO [stderr] 
Dec 01 11:03:18.712 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.78s
Dec 01 11:03:18.731 INFO [stderr] su: No module specific data is present
Dec 01 11:03:19.454 INFO running `"docker" "inspect" "6dcc8d4eef54243dcae32f26b1412e9c6bb4bcc940282d0f8fa2861277530865"`
Dec 01 11:03:19.643 INFO running `"docker" "rm" "-f" "6dcc8d4eef54243dcae32f26b1412e9c6bb4bcc940282d0f8fa2861277530865"`
Dec 01 11:03:19.971 INFO [stdout] 6dcc8d4eef54243dcae32f26b1412e9c6bb4bcc940282d0f8fa2861277530865
Dec 01 11:03:19.979 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-3/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 11:03:20.459 INFO [stdout] fafce9f2054fc4a09054b4cd54ffb61e754fe862167c9f29090629d9c0534f4a
Dec 01 11:03:20.461 INFO running `"docker" "start" "-a" "fafce9f2054fc4a09054b4cd54ffb61e754fe862167c9f29090629d9c0534f4a"`
Dec 01 11:03:22.195 INFO [stderr] usermod: no changes
Dec 01 11:03:22.251 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Dec 01 11:03:22.438 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Dec 01 11:03:22.455 INFO [stderr]      Running /target/debug/deps/shadowsocks-ea51a1a870306e53
Dec 01 11:03:22.461 INFO [stdout] 
Dec 01 11:03:22.461 INFO [stdout] running 3 tests
Dec 01 11:03:22.461 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored
Dec 01 11:03:22.462 INFO [stdout] test asyncdns::test::parse_response ... ok
Dec 01 11:03:22.603 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED
Dec 01 11:03:22.604 INFO [stdout] 
Dec 01 11:03:22.604 INFO [stdout] failures:
Dec 01 11:03:22.604 INFO [stdout] 
Dec 01 11:03:22.604 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ----
Dec 01 11:03:22.604 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") }
Dec 01 11:03:22.604 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21
Dec 01 11:03:22.604 INFO [stdout] stack backtrace:
Dec 01 11:03:22.604 INFO [stdout]    0:     0x55d92756bf1f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 01 11:03:22.604 INFO [stdout]    1:     0x55d927575617 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 01 11:03:22.604 INFO [stdout]    2:     0x55d9275701af - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:211
Dec 01 11:03:22.604 INFO [stdout]    3:     0x55d92756feae - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:221
Dec 01 11:03:22.604 INFO [stdout]    4:     0x55d92757088e - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:476
Dec 01 11:03:22.604 INFO [stdout]    5:     0x55d92754deb7 - std::panicking::begin_panic::h533ed9f9b6590ef8
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:410
Dec 01 11:03:22.604 INFO [stdout]    6:     0x55d9273dccfb - shadowsocks::asyncdns::test::test_block_resolve::h02964c2517bc57f0
Dec 01 11:03:22.604 INFO [stdout]                                at src/asyncdns.rs:784
Dec 01 11:03:22.604 INFO [stdout]    7:     0x55d9273dcfb7 - shadowsocks::asyncdns::test::ipv4_block_resolve::h49f5577b9250729d
Dec 01 11:03:22.604 INFO [stdout]                                at src/asyncdns.rs:792
Dec 01 11:03:22.604 INFO [stdout]    8:     0x55d9273dcf99 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::hc93cc8015209aa74
Dec 01 11:03:22.604 INFO [stdout]                                at src/asyncdns.rs:791
Dec 01 11:03:22.604 INFO [stdout]    9:     0x55d9273ce68d - core::ops::function::FnOnce::call_once::h996575e7e5300f6a
Dec 01 11:03:22.604 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 11:03:22.604 INFO [stdout]   10:     0x55d9273f1aae - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 01 11:03:22.604 INFO [stdout]                                at libtest/lib.rs:1468
Dec 01 11:03:22.604 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 11:03:22.604 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 01 11:03:22.604 INFO [stdout]   11:     0x55d9275805c9 - __rust_maybe_catch_panic
Dec 01 11:03:22.604 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 01 11:03:22.604 INFO [stdout]   12:     0x55d927413702 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 11:03:22.604 INFO [stdout]                                at libtest/lib.rs:1423
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 01 11:03:22.604 INFO [stdout]   13:     0x55d9274141b4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panic.rs:313
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:310
Dec 01 11:03:22.604 INFO [stdout]   14:     0x55d9275805c9 - __rust_maybe_catch_panic
Dec 01 11:03:22.604 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 01 11:03:22.604 INFO [stdout]   15:     0x55d9274013ec - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 01 11:03:22.604 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 01 11:03:22.604 INFO [stdout]   16:     0x55d92756df0d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 01 11:03:22.604 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 01 11:03:22.604 INFO [stdout]   17:     0x55d9275609e5 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 01 11:03:22.604 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 01 11:03:22.604 INFO [stdout]   18:     0x7fd0faa706b9 - start_thread
Dec 01 11:03:22.604 INFO [stdout]   19:     0x7fd0fa59041c - clone
Dec 01 11:03:22.604 INFO [stdout]   20:                0x0 - <unknown>
Dec 01 11:03:22.604 INFO [stdout] 
Dec 01 11:03:22.604 INFO [stdout] 
Dec 01 11:03:22.604 INFO [stdout] failures:
Dec 01 11:03:22.604 INFO [stdout]     asyncdns::test::ipv4_block_resolve
Dec 01 11:03:22.604 INFO [stdout] 
Dec 01 11:03:22.604 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out
Dec 01 11:03:22.604 INFO [stdout] 
Dec 01 11:03:22.607 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 01 11:03:22.609 INFO [stderr] su: No module specific data is present
Dec 01 11:03:23.529 INFO running `"docker" "inspect" "fafce9f2054fc4a09054b4cd54ffb61e754fe862167c9f29090629d9c0534f4a"`
Dec 01 11:03:23.897 INFO running `"docker" "rm" "-f" "fafce9f2054fc4a09054b4cd54ffb61e754fe862167c9f29090629d9c0534f4a"`
Dec 01 11:03:24.327 INFO [stdout] fafce9f2054fc4a09054b4cd54ffb61e754fe862167c9f29090629d9c0534f4a
